**** dT 0.000 * top TEST ../../../../bin/vinyltest/tests/b00032.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:63490 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub **** top extmacro def topsrc=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/../.. **** top macro def testdir=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/tests **** top macro def tmpdir=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.150069.3e318bbf **** top macro def vtcid=vtc.150069.3e318bbf **** dT 0.001 ** top === vtest "CLI coverage test" * top VTEST CLI coverage test ** top === vinyl v1 -cliok storage.list **** dT 0.017 ** v1 Launch *** v1 CMD: cd ${pwd} && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.150069.3e318bbf/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 59604' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.150069.3e318bbf/v1/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: *** v1 CMD: cd /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.150069.3e318bbf/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 59604' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.150069.3e318bbf/v1/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: **** dT 0.020 *** v1 PID: 150095 **** v1 macro def v1_pid=150095 **** v1 macro def v1_name=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.150069.3e318bbf/v1 **** dT 0.066 *** v1 debug|Warning: mlock() of VSM failed: Not owner (1) **** dT 0.069 *** v1 debug|Debug: Version: vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v1 debug|Debug: Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|200 292 *** v1 debug|----------------------------- *** v1 debug|Vinyl Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v1 debug| *** v1 debug|Type 'help' for command list. *** v1 debug|Type 'quit' to close CLI session. *** v1 debug|Type 'start' to launch worker process. *** v1 debug| **** dT 0.167 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 4 *** v1 CLI RX 107 **** v1 CLI RX|opcsuiifcicwdsppdiyvorilkxnfkuyi **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** v1 CLI TX|auth f359da66924c1e64c2090b0f16ec10d0386d6399ee4de114fef2464a1e88e38d **** dT 0.168 *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Vinyl Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v1 CLI RX|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 **** v1 CLI RX| **** v1 CLI RX|Type 'help' for command list. **** v1 CLI RX|Type 'quit' to close CLI session. **** v1 CLI RX|Type 'start' to launch worker process. **** v1 CLI TX|storage.list *** v1 CLI RX 200 **** v1 CLI RX|Storage devices: **** v1 CLI RX|\tstorage.s0 = default **** v1 CLI RX|\tstorage.Transient = default ** v1 CLI 200 ** top === vinyl v1 -clijson "storage.list -j" **** v1 CLI TX|storage.list -j *** v1 CLI RX 200 **** v1 CLI RX|[ 2, ["storage.list", "-j"], 1773714105.154, **** v1 CLI RX| { **** v1 CLI RX| "name": "s0", **** v1 CLI RX| "storage": "default" **** v1 CLI RX|}, **** v1 CLI RX| { **** v1 CLI RX| "name": "Transient", **** v1 CLI RX| "storage": "default" **** v1 CLI RX|} **** v1 CLI RX|] ** v1 CLI 200 ** top === server s1 { **** dT 0.169 ** s1 Starting server **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=39622 **** s1 macro def s1_sock=127.0.0.1:39622 * s1 Listen on 127.0.0.1:39622 ** top === vinyl v1 ** top === vinyl v1 -cliok vcl.list **** v1 CLI TX|vcl.list *** v1 CLI RX 200 ** v1 CLI 200 ** top === vinyl v1 -clijson "vcl.list -j" **** v1 CLI TX|vcl.list -j ** s1 Started on 127.0.0.1:39622 (1 iterations) *** v1 CLI RX 200 **** v1 CLI RX|[ 2, ["vcl.list", "-j"], 1773714105.154 **** v1 CLI RX|] ** v1 CLI 200 ** top === vinyl v1 -vcl+backend {} **** v1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX|backend s1 { .host = "127.0.0.1"; .port = "39622"; } **** v1 CLI TX| **** v1 CLI TX| **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.269 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.369 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.469 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.569 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.669 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.693 *** v1 CLI RX 200 **** v1 CLI RX|Message from VCC-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|Message from C-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise **** v1 CLI RX|"vgc.c", line 3172: warning: statement not reached (E_STATEMENT_NOT_REACHED) **** v1 CLI RX|Message from dlopen: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl1 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl1' now active ** top === vinyl v1 -vcl+backend {} **** v1 CLI TX|vcl.inline vcl2 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX|backend s1 { .host = "127.0.0.1"; .port = "39622"; } **** v1 CLI TX| **** v1 CLI TX| **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.769 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.869 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.969 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.069 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.169 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.203 *** v1 CLI RX 200 **** v1 CLI RX|Message from VCC-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|Message from C-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise **** v1 CLI RX|"vgc.c", line 3172: warning: statement not reached (E_STATEMENT_NOT_REACHED) **** v1 CLI RX|Message from dlopen: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl2 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl2' now active ** top === vinyl v1 -cliok vcl.list **** v1 CLI TX|vcl.list *** v1 CLI RX 200 **** v1 CLI RX|available auto warm - vcl1 **** v1 CLI RX|active auto warm - vcl2 ** v1 CLI 200 ** top === vinyl v1 -clijson "vcl.list -j" **** v1 CLI TX|vcl.list -j *** v1 CLI RX 200 **** v1 CLI RX|[ 2, ["vcl.list", "-j"], 1773714106.189, **** v1 CLI RX| { **** v1 CLI RX| "status": "available", **** v1 CLI RX| "state": "auto", **** v1 CLI RX| "temperature": "warm", **** v1 CLI RX| "name": "vcl1" **** v1 CLI RX|}, **** v1 CLI RX| { **** v1 CLI RX| "status": "active", **** v1 CLI RX| "state": "auto", **** v1 CLI RX| "temperature": "warm", **** v1 CLI RX| "name": "vcl2" **** v1 CLI RX|} **** v1 CLI RX|] ** v1 CLI 200 ** top === vinyl v1 -cliok start **** v1 CLI TX|start **** dT 1.217 *** v1 debug|Could not fork child: Resource temporarily unavailable **** v1 STDOUT EOF ---- v1 CLI failed (start) = 400 400 CLI communication error (hdr) * top RESETTING after ../../../../bin/vinyltest/tests/b00032.vtc ** s1 Waiting for server (12/-1) ** v1 Wait **** v1 CLI TX|panic.show *** v1 CLI RX 400 **** v1 CLI RX|CLI communication error (hdr) * diag 0.0 Assert error in vinyl_fatal_cleanup(), ../../../../bin/vinyltest/vtc_vinyl.c line 124: * diag 0.0 Condition(pthread_join(v->tp, ((void *)0)) failed) not true. (errno=3 No such process) * diag 0.0 Backtrace: * diag 0.0 fffffc5ed69a2b7d: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'vbt_execinfo+0x3d [0xfffffc5ed69a2b7d] * diag 0.0 fffffc5ed69a2f21: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VBT_format+0x41 [0xfffffc5ed69a2f21] * diag 0.0 fffffc5ed69a2fd0: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VBT_dump+0x90 [0xfffffc5ed69a2fd0] * diag 0.0 47162d: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vtc_log_VAS_Fail+0xcd [0x47162d] * diag 0.0 fffffc5ed69a14ac: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VAS_Fail+0x8c [0xfffffc5ed69a14ac] * diag 0.0 49c494: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vinyl_fatal_cleanup+0x194 [0x49c494] * diag 0.0 4a1551: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vinyl_wait+0xf1 [0x4a1551] * diag 0.0 4a3f72: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'cmd_vinyl+0xb2 [0x4a3f72] * diag 0.0 437fd4: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'reset_cmds+0x234 [0x437fd4] * diag 0.0 438112: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'fail_out+0xf2 [0x438112] * diag 0.0 46fc32: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vtc_logfail+0x52 [0x46fc32] * diag 0.0 470663: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vtc_fatal+0x363 [0x470663] * diag 0.0 49c896: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vinyl_ask_cli+0x3b6 [0x49c896] * diag 0.0 4a1b85: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vinyl_cli+0x345 [0x4a1b85] * diag 0.0 4a4b26: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'cmd_vinyl+0xc66 [0x4a4b26] * diag 0.0 437c5a: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'parse_string+0x11da [0x437c5a] * diag 0.0 43891f: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'exec_file+0x6ff [0x43891f] * diag 0.0 47d9e2: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'start_test+0x982 [0x47d9e2] * diag 0.0 480edb: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'main+0x53b [0x480edb] * diag 0.0 428534: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'0x28534 [0x428534] * diag 0.0 # top TEST ../../../../bin/vinyltest/tests/b00032.vtc FAILED (1.224) signal=6 FAIL tests/b00032.vtc (exit status: 2)